DEBUG [main] (EnvironmentVariable.java:30) edu.serg.mbar.algorithms.functions.EnvironmentVariable - Recieved a request to retrieve the value of an environment variable
 DEBUG [main] (EnvironmentVariable.java:34) edu.serg.mbar.algorithms.functions.EnvironmentVariable - the name of the environment variable whose value is to be retreived is CurrentTime
 DEBUG [main] (EnvironmentVariable.java:39) edu.serg.mbar.algorithms.functions.EnvironmentVariable - The Value of the environment variable is Thu Mar 12 15:27:15 GMT+05:00 2009
  INFO [main] (ExpressionEvaluationManager.java:161) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - * --> * Elapsed Time: Expression Evaluated in [00:00:00.078] Time
 DEBUG [main] (ExpressionEvaluationManager.java:87) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Element 1 is p:Constant
 DEBUG [main] (ExpressionEvaluationManager.java:113) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - The datatype of the Constant is Time
 DEBUG [main] (ExpressionEvaluationManager.java:126) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - The value of the constant = 1700
 DEBUG [main] (ExpressionEvaluationManager.java:129) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Adding the value of the constant to the list of the arguments that are to be passed to the function
 DEBUG [main] (ExpressionEvaluationManager.java:142) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - * # Calling Function 'edu.serg.mbar.algorithms.functions.TimeLessThan' with the vector of Size 2 # *
 DEBUG [main] (TimeLessThan.java:17) edu.serg.mbar.algorithms.functions.TimeLessThan - Received a request to evaluate if the first Value of Time is earlier than the second value of time
 DEBUG [main] (TimeLessThan.java:32) edu.serg.mbar.algorithms.functions.TimeLessThan - First Value of time  is Thu Jan 01 15:27:00 GMT+05:00 1970 and the Second Value is Thu Jan 01 17:00:00 GMT+05:00 1970
 DEBUG [main] (TimeLessThan.java:36) edu.serg.mbar.algorithms.functions.TimeLessThan - First value of Time is earlier than the second value
  INFO [main] (ExpressionEvaluationManager.java:161) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - * --> * Elapsed Time: Expression Evaluated in [00:00:00.094] Time
 DEBUG [main] (ExpressionEvaluationManager.java:142) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - * # Calling Function 'edu.serg.mbar.algorithms.functions.LogicalAnd' with the vector of Size 2 # *
 DEBUG [main] (LogicalAnd.java:24) edu.serg.mbar.algorithms.functions.TimeLessThan - applying the logical and Function to 2 items
 DEBUG [main] (LogicalAnd.java:29) edu.serg.mbar.algorithms.functions.TimeLessThan - applying logicalAND function the element at 0 whose value is true
 DEBUG [main] (LogicalAnd.java:29) edu.serg.mbar.algorithms.functions.TimeLessThan - applying logicalAND function the element at 1 whose value is true
  INFO [main] (ExpressionEvaluationManager.java:161) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - * --> * Elapsed Time: Expression Evaluated in [00:00:00.149] Time
 DEBUG [main] (ExpressionEvaluationManager.java:142) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - * # Calling Function 'edu.serg.mbar.algorithms.functions.LogicalAnd' with the vector of Size 3 # *
 DEBUG [main] (LogicalAnd.java:24) edu.serg.mbar.algorithms.functions.TimeLessThan - applying the logical and Function to 3 items
 DEBUG [main] (LogicalAnd.java:29) edu.serg.mbar.algorithms.functions.TimeLessThan - applying logicalAND function the element at 0 whose value is true
 DEBUG [main] (LogicalAnd.java:29) edu.serg.mbar.algorithms.functions.TimeLessThan - applying logicalAND function the element at 1 whose value is false
  INFO [main] (ExpressionEvaluationManager.java:161) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - * --> * Elapsed Time: Expression Evaluated in [00:00:00.290] Time
  INFO [main] (ExpressionEvaluationManager.java:63) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Evaluating Expression
 DEBUG [main] (ExpressionEvaluationManager.java:71) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Start evaluating expression
  INFO [main] (ExpressionEvaluationManager.java:78) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - FunctionID of the expression being evaluated is 'edu.serg.mbar.algorithms.functions.StringEqualIgnoreCase'
 DEBUG [main] (ExpressionEvaluationManager.java:82) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Child nodes within New expression Node are 2
 DEBUG [main] (ExpressionEvaluationManager.java:87) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Element 0 is p:ObjectAttribute
 DEBUG [main] (AttributeManager.java:102) edu.serg.mbar.ucon.AttributeManager - Retrieve attribute(Department)of Object(imran001)
 DEBUG [main] (AttributeManager.java:105) edu.serg.mbar.ucon.AttributeManager - 1 Values for the attribute have been retrieved
 DEBUG [main] (AttributeManager.java:107) edu.serg.mbar.ucon.AttributeManager - Value1 is = Finance
 DEBUG [main] (ExpressionEvaluationManager.java:87) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Element 1 is p:Constant
 DEBUG [main] (ExpressionEvaluationManager.java:113) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - The datatype of the Constant is String
 DEBUG [main] (ExpressionEvaluationManager.java:126) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - The value of the constant = Finance
 DEBUG [main] (ExpressionEvaluationManager.java:129) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Adding the value of the constant to the list of the arguments that are to be passed to the function
 DEBUG [main] (ExpressionEvaluationManager.java:142) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - * # Calling Function 'edu.serg.mbar.algorithms.functions.StringEqualIgnoreCase' with the vector of Size 2 # *
 DEBUG [main] (StringEqualIgnoreCase.java:26) edu.serg.mbar.ucon.AttributeManager - Both Strings 'Finance' and 'Finance' are EQUAL
  INFO [main] (ExpressionEvaluationManager.java:161) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - * --> * Elapsed Time: Expression Evaluated in [00:00:00.074] Time
  INFO [main] (ExpressionEvaluationManager.java:63) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Evaluating Expression
 DEBUG [main] (ExpressionEvaluationManager.java:71) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Start evaluating expression
  INFO [main] (ExpressionEvaluationManager.java:78) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - FunctionID of the expression being evaluated is 'edu.serg.mbar.algorithms.functions.StringEqualIgnoreCase'
 DEBUG [main] (ExpressionEvaluationManager.java:82) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Child nodes within New expression Node are 2
 DEBUG [main] (ExpressionEvaluationManager.java:87) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Element 0 is p:ObjectAttribute
 DEBUG [main] (AttributeManager.java:102) edu.serg.mbar.ucon.AttributeManager - Retrieve attribute(Designation)of Object(imran001)
 DEBUG [main] (AttributeManager.java:105) edu.serg.mbar.ucon.AttributeManager - 1 Values for the attribute have been retrieved
 DEBUG [main] (AttributeManager.java:107) edu.serg.mbar.ucon.AttributeManager - Value1 is = ControllerFinance
 DEBUG [main] (ExpressionEvaluationManager.java:87) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Element 1 is p:Constant
 DEBUG [main] (ExpressionEvaluationManager.java:113) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - The datatype of the Constant is String
 DEBUG [main] (ExpressionEvaluationManager.java:126) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - The value of the constant = ControllerFinance
 DEBUG [main] (ExpressionEvaluationManager.java:129) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Adding the value of the constant to the list of the arguments that are to be passed to the function
 DEBUG [main] (ExpressionEvaluationManager.java:142) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - * # Calling Function 'edu.serg.mbar.algorithms.functions.StringEqualIgnoreCase' with the vector of Size 2 # *
 DEBUG [main] (StringEqualIgnoreCase.java:26) edu.serg.mbar.ucon.AttributeManager - Both Strings 'ControllerFinance' and 'ControllerFinance' are EQUAL
  INFO [main] (ExpressionEvaluationManager.java:161) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - * --> * Elapsed Time: Expression Evaluated in [00:00:00.073] Time
  INFO [main] (ExpressionEvaluationManager.java:63) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Evaluating Expression
 DEBUG [main] (ExpressionEvaluationManager.java:71) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Start evaluating expression
  INFO [main] (ExpressionEvaluationManager.java:78) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - FunctionID of the expression being evaluated is 'edu.serg.mbar.algorithms.functions.LogicalAnd'
 DEBUG [main] (ExpressionEvaluationManager.java:82) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Child nodes within New expression Node are 2
 DEBUG [main] (ExpressionEvaluationManager.java:87) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Element 0 is p:Expression
  INFO [main] (ExpressionEvaluationManager.java:63) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Evaluating Expression
 DEBUG [main] (ExpressionEvaluationManager.java:71) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Start evaluating expression
  INFO [main] (ExpressionEvaluationManager.java:78) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - FunctionID of the expression being evaluated is 'edu.serg.mbar.algorithms.functions.TimeGreaterThan'
 DEBUG [main] (ExpressionEvaluationManager.java:82) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Child nodes within New expression Node are 2
 DEBUG [main] (ExpressionEvaluationManager.java:87) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Element 0 is p:Expression
  INFO [main] (ExpressionEvaluationManager.java:63) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Evaluating Expression
 DEBUG [main] (ExpressionEvaluationManager.java:71) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Start evaluating expression
  INFO [main] (ExpressionEvaluationManager.java:78) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - FunctionID of the expression being evaluated is 'edu.serg.mbar.algorithms.functions.EnvironmentVariable'
 DEBUG [main] (ExpressionEvaluationManager.java:82) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Child nodes within New expression Node are 1
 DEBUG [main] (ExpressionEvaluationManager.java:87) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Element 0 is p:Constant
 DEBUG [main] (ExpressionEvaluationManager.java:113) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - The datatype of the Constant is String
 DEBUG [main] (ExpressionEvaluationManager.java:126) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - The value of the constant = CurrentTime
 DEBUG [main] (ExpressionEvaluationManager.java:129) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Adding the value of the constant to the list of the arguments that are to be passed to the function
 DEBUG [main] (ExpressionEvaluationManager.java:142) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - * # Calling Function 'edu.serg.mbar.algorithms.functions.EnvironmentVariable' with the vector of Size 1 # *
 DEBUG [main] (EnvironmentVariable.java:30) edu.serg.mbar.algorithms.functions.EnvironmentVariable - Recieved a request to retrieve the value of an environment variable
 DEBUG [main] (EnvironmentVariable.java:34) edu.serg.mbar.algorithms.functions.EnvironmentVariable - the name of the environment variable whose value is to be retreived is CurrentTime
 DEBUG [main] (EnvironmentVariable.java:39) edu.serg.mbar.algorithms.functions.EnvironmentVariable - The Value of the environment variable is Thu Mar 12 15:27:16 GMT+05:00 2009
  INFO [main] (ExpressionEvaluationManager.java:161) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - * --> * Elapsed Time: Expression Evaluated in [00:00:00.022] Time
 DEBUG [main] (ExpressionEvaluationManager.java:87) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Element 1 is p:Constant
 DEBUG [main] (ExpressionEvaluationManager.java:113) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - The datatype of the Constant is Time
 DEBUG [main] (ExpressionEvaluationManager.java:126) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - The value of the constant = 1300
 DEBUG [main] (ExpressionEvaluationManager.java:129) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Adding the value of the constant to the list of the arguments that are to be passed to the function
 DEBUG [main] (ExpressionEvaluationManager.java:142) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - * # Calling Function 'edu.serg.mbar.algorithms.functions.TimeGreaterThan' with the vector of Size 2 # *
 DEBUG [main] (TimeGreaterThan.java:15) edu.serg.mbar.algorithms.functions.TimeGreaterThan - Received a request to evaluate if the first Value of Time is later than the second value of time
 DEBUG [main] (TimeGreaterThan.java:30) edu.serg.mbar.algorithms.functions.TimeGreaterThan - First Value of time  is Thu Jan 01 15:27:00 GMT+05:00 1970 and the Second Value is Thu Jan 01 13:00:00 GMT+05:00 1970
 DEBUG [main] (TimeGreaterThan.java:34) edu.serg.mbar.algorithms.functions.TimeGreaterThan - First value of Time is Later than the second value
  INFO [main] (ExpressionEvaluationManager.java:161) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - * --> * Elapsed Time: Expression Evaluated in [00:00:00.045] Time
 DEBUG [main] (ExpressionEvaluationManager.java:87) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Element 1 is p:Expression
  INFO [main] (ExpressionEvaluationManager.java:63) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Evaluating Expression
 DEBUG [main] (ExpressionEvaluationManager.java:71) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Start evaluating expression
  INFO [main] (ExpressionEvaluationManager.java:78) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - FunctionID of the expression being evaluated is 'edu.serg.mbar.algorithms.functions.TimeLessThan'
 DEBUG [main] (ExpressionEvaluationManager.java:82) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Child nodes within New expression Node are 2
 DEBUG [main] (ExpressionEvaluationManager.java:87) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Element 0 is p:Expression
  INFO [main] (ExpressionEvaluationManager.java:63) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Evaluating Expression
 DEBUG [main] (ExpressionEvaluationManager.java:71) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Start evaluating expression
  INFO [main] (ExpressionEvaluationManager.java:78) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - FunctionID of the expression being evaluated is 'edu.serg.mbar.algorithms.functions.EnvironmentVariable'
 DEBUG [main] (ExpressionEvaluationManager.java:82) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Child nodes within New expression Node are 1
 DEBUG [main] (ExpressionEvaluationManager.java:87) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Element 0 is p:Constant
 DEBUG [main] (ExpressionEvaluationManager.java:113) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - The datatype of the Constant is String
 DEBUG [main] (ExpressionEvaluationManager.java:126) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - The value of the constant = CurrentTime
 DEBUG [main] (ExpressionEvaluationManager.java:129) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Adding the value of the constant to the list of the arguments that are to be passed to the function
 DEBUG [main] (ExpressionEvaluationManager.java:142) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - * # Calling Function 'edu.serg.mbar.algorithms.functions.EnvironmentVariable' with the vector of Size 1 # *
 DEBUG [main] (EnvironmentVariable.java:30) edu.serg.mbar.algorithms.functions.EnvironmentVariable - Recieved a request to retrieve the value of an environment variable
 DEBUG [main] (EnvironmentVariable.java:34) edu.serg.mbar.algorithms.functions.EnvironmentVariable - the name of the environment variable whose value is to be retreived is CurrentTime
 DEBUG [main] (EnvironmentVariable.java:39) edu.serg.mbar.algorithms.functions.EnvironmentVariable - The Value of the environment variable is Thu Mar 12 15:27:16 GMT+05:00 2009
  INFO [main] (ExpressionEvaluationManager.java:161) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - * --> * Elapsed Time: Expression Evaluated in [00:00:00.022] Time
 DEBUG [main] (ExpressionEvaluationManager.java:87) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Element 1 is p:Constant
 DEBUG [main] (ExpressionEvaluationManager.java:113) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - The datatype of the Constant is Time
 DEBUG [main] (ExpressionEvaluationManager.java:126) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - The value of the constant = 1700
 DEBUG [main] (ExpressionEvaluationManager.java:129) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Adding the value of the constant to the list of the arguments that are to be passed to the function
 DEBUG [main] (ExpressionEvaluationManager.java:142) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - * # Calling Function 'edu.serg.mbar.algorithms.functions.TimeLessThan' with the vector of Size 2 # *
 DEBUG [main] (TimeLessThan.java:17) edu.serg.mbar.algorithms.functions.TimeLessThan - Received a request to evaluate if the first Value of Time is earlier than the second value of time
 DEBUG [main] (TimeLessThan.java:32) edu.serg.mbar.algorithms.functions.TimeLessThan - First Value of time  is Thu Jan 01 15:27:00 GMT+05:00 1970 and the Second Value is Thu Jan 01 17:00:00 GMT+05:00 1970
 DEBUG [main] (TimeLessThan.java:36) edu.serg.mbar.algorithms.functions.TimeLessThan - First value of Time is earlier than the second value
  INFO [main] (ExpressionEvaluationManager.java:161) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - * --> * Elapsed Time: Expression Evaluated in [00:00:00.046] Time
 DEBUG [main] (ExpressionEvaluationManager.java:142) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - * # Calling Function 'edu.serg.mbar.algorithms.functions.LogicalAnd' with the vector of Size 2 # *
 DEBUG [main] (LogicalAnd.java:24) edu.serg.mbar.algorithms.functions.TimeLessThan - applying the logical and Function to 2 items
 DEBUG [main] (LogicalAnd.java:29) edu.serg.mbar.algorithms.functions.TimeLessThan - applying logicalAND function the element at 0 whose value is true
 DEBUG [main] (LogicalAnd.java:29) edu.serg.mbar.algorithms.functions.TimeLessThan - applying logicalAND function the element at 1 whose value is true
  INFO [main] (ExpressionEvaluationManager.java:161) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - * --> * Elapsed Time: Expression Evaluated in [00:00:00.105] Time
  INFO [main] (All.java:27) edu.serg.mbar.algorithms.combiningalgorithms.All - Analyzing the Results of all the expressions in the Condition Node
 DEBUG [main] (All.java:32) edu.serg.mbar.algorithms.combiningalgorithms.All - The value of item(0) is false
 DEBUG [main] (All.java:39) edu.serg.mbar.algorithms.combiningalgorithms.All - The value returned is false
  INFO [main] (PolicyEvaluationManager.java:189) edu.serg.mbar.ucon.pdp.PolicyEvaluationManager - * --> * Elapsed Time: Condition Evaluated in [00:00:00.577] Time the Validity of the condition is false
 DEBUG [main] (PolicyEvaluationManager.java:94) edu.serg.mbar.ucon.pdp.PolicyEvaluationManager - The validity of Condition(0) is false
  INFO [main] (PolicyEvaluationManager.java:110) edu.serg.mbar.ucon.pdp.PolicyEvaluationManager - * --> * Elapsed Time: Policy Evaluation completed in [00:00:00.622] Time the Validity of the Policy is false
  INFO [main] (AccessManager.java:147) edu.serg.mbar.ucon.pdp.AccessManager - Access Decision = false
  INFO [main] (ConsoleFrontEnd.java:156) edu.serg.mbar.frontend.ConsoleFrontEnd - * --> * Elapsed Time: Execution completed in [00:00:01.575] Time
 DEBUG [main] (ConsoleFrontEnd.java:46) edu.serg.mbar.frontend.ConsoleFrontEnd - Start Execution
 DEBUG [main] (ConsoleFrontEnd.java:47) edu.serg.mbar.frontend.ConsoleFrontEnd - Initialized the log4j library.
  INFO [main] (AuthenticationManager.java:81) edu.serg.mbar.ucon.AuthenticationManager - Authenticating user: imran001
 DEBUG [main] (AuthenticationManager.java:84) edu.serg.mbar.ucon.AuthenticationManager - Using XPath to read the subject information from file: sampledata/objects/imran001.xml
 DEBUG [main] (AuthenticationManager.java:90) edu.serg.mbar.ucon.AuthenticationManager - Resolving subjectSecret using XPath: //obj:Object[obj:ObjectID/text()='imran001']/obj:Secret/text()
  INFO [main] (AuthenticationManager.java:104) edu.serg.mbar.ucon.AuthenticationManager - Authentication succeeded for subject: imran001
 DEBUG [main] (ConsoleFrontEnd.java:87) edu.serg.mbar.frontend.ConsoleFrontEnd - Authentication successful
  INFO [main] (AuthenticationManager.java:81) edu.serg.mbar.ucon.AuthenticationManager - Authenticating user: kamran001
 DEBUG [main] (AuthenticationManager.java:84) edu.serg.mbar.ucon.AuthenticationManager - Using XPath to read the subject information from file: sampledata/objects/kamran001.xml
 DEBUG [main] (AuthenticationManager.java:90) edu.serg.mbar.ucon.AuthenticationManager - Resolving subjectSecret using XPath: //obj:Object[obj:ObjectID/text()='kamran001']/obj:Secret/text()
  INFO [main] (AuthenticationManager.java:104) edu.serg.mbar.ucon.AuthenticationManager - Authentication succeeded for subject: kamran001
 DEBUG [main] (ConsoleFrontEnd.java:109) edu.serg.mbar.frontend.ConsoleFrontEnd - Authentication successful. Received attributes.
  INFO [main] (ConsoleFrontEnd.java:125) edu.serg.mbar.frontend.ConsoleFrontEnd - Creating an AttributeManager
  INFO [main] (ConsoleFrontEnd.java:127) edu.serg.mbar.frontend.ConsoleFrontEnd - Creating instance of the Expression Evaluator and assigning an attribute reader to it
  INFO [main] (ConsoleFrontEnd.java:133) edu.serg.mbar.frontend.ConsoleFrontEnd - Creating an AccessManager
 DEBUG [main] (AccessManager.java:69) edu.serg.mbar.ucon.pdp.AccessManager - Adding a new DecisionEvent listener.
 DEBUG [main] (AccessManager.java:81) edu.serg.mbar.ucon.pdp.AccessManager - Adding a new MutabilityEvent listener.
  INFO [main] (ConsoleFrontEnd.java:148) edu.serg.mbar.frontend.ConsoleFrontEnd - Requesting an Object 'BudgetDoc' for Subjec 'imran001'
 DEBUG [main] (PrimitiveActionsManager.java:88) edu.serg.mbar.ucon.pep.PrimitiveActionsManager - Handling Primitive Actions [PolicyType=Requesting], [Object=BudgetDoc], [Subject=imran001]
 DEBUG [main] (PrimitiveActionsManager.java:91) edu.serg.mbar.ucon.pep.PrimitiveActionsManager - Retreiving the primitive actions nodes with the help of xPath = //p:PolicySet/p:Policy[@PolicyType='Requesting' and p:Target/p:Subjects/p:Subject/text()='imran001' and p:Target/p:Objects/p:Object/text()='BudgetDoc']/p:PrimitiveActions
 DEBUG [main] (PrimitiveActionsManager.java:96) edu.serg.mbar.ucon.pep.PrimitiveActionsManager - The total number of primitive actions nodes retreived is 1
 DEBUG [main] (PrimitiveActionsManager.java:113) edu.serg.mbar.ucon.pep.PrimitiveActionsManager - Started processing Primitive Action Node
 DEBUG [main] (PrimitiveActionsManager.java:136) edu.serg.mbar.ucon.pep.PrimitiveActionsManager - Started processing Attribute update Node
  INFO [main] (ExpressionEvaluationManager.java:63) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Evaluating Expression
 DEBUG [main] (ExpressionEvaluationManager.java:71) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Start evaluating expression
  INFO [main] (ExpressionEvaluationManager.java:78) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - FunctionID of the expression being evaluated is 'edu.serg.mbar.algorithms.functions.Add'
 DEBUG [main] (ExpressionEvaluationManager.java:82) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Child nodes within New expression Node are 2
 DEBUG [main] (ExpressionEvaluationManager.java:87) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Element 0 is p:ObjectAttribute
 DEBUG [main] (AttributeManager.java:102) edu.serg.mbar.ucon.AttributeManager - Retrieve attribute(SimultaneousReads)of Object(BudgetDoc)
 DEBUG [main] (AttributeManager.java:105) edu.serg.mbar.ucon.AttributeManager - 1 Values for the attribute have been retrieved
 DEBUG [main] (AttributeManager.java:107) edu.serg.mbar.ucon.AttributeManager - Value1 is = 2.0
 DEBUG [main] (ExpressionEvaluationManager.java:87) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Element 1 is p:Constant
 DEBUG [main] (ExpressionEvaluationManager.java:113) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - The datatype of the Constant is Integer
 DEBUG [main] (ExpressionEvaluationManager.java:126) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - The value of the constant = 1
 DEBUG [main] (ExpressionEvaluationManager.java:129) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Adding the value of the constant to the list of the arguments that are to be passed to the function
 DEBUG [main] (ExpressionEvaluationManager.java:142) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - * # Calling Function 'edu.serg.mbar.algorithms.functions.Add' with the vector of Size 2 # *
 DEBUG [main] (Add.java:28) edu.serg.mbar.algorithms.functions.Add - *Start adding 2elements
 DEBUG [main] (Add.java:33) edu.serg.mbar.algorithms.functions.Add - the value of first element is 2.0
 DEBUG [main] (Add.java:33) edu.serg.mbar.algorithms.functions.Add - the value of first element is 1
 DEBUG [main] (Add.java:38) edu.serg.mbar.algorithms.functions.Add - *The result of the summation is 3.0
  INFO [main] (ExpressionEvaluationManager.java:161) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - * --> * Elapsed Time: Expression Evaluated in [00:00:00.118] Time
 DEBUG [main] (PrimitiveActionsManager.java:178) edu.serg.mbar.ucon.pep.PrimitiveActionsManager - Determining the Name of the Attribute from the TargetAttribute
 DEBUG [main] (PrimitiveActionsManager.java:202) edu.serg.mbar.ucon.pep.PrimitiveActionsManager - The name of the target attribute is 'SimultaneousReads'
  INFO [main] (AttributeManager.java:136) edu.serg.mbar.ucon.AttributeManager - Updating 'SimultaneousReads' Attribute of BudgetDoc
 DEBUG [main] (AttributeManager.java:151) edu.serg.mbar.ucon.AttributeManager - Removing old values of Attributes
 DEBUG [main] (AttributeManager.java:163) edu.serg.mbar.ucon.AttributeManager - Old Values of Attributes removed
 DEBUG [main] (AttributeManager.java:181) edu.serg.mbar.ucon.AttributeManager - Adding New values of attributes
 DEBUG [main] (AttributeManager.java:191) edu.serg.mbar.ucon.AttributeManager - New values have been added proceeding to save the document
  INFO [main] (AttributeManager.java:206) edu.serg.mbar.ucon.AttributeManager - Saving File
  INFO [main] (AttributeManager.java:223) edu.serg.mbar.ucon.AttributeManager - File Saved: sampledata/objects/BudgetDoc.xml
  INFO [main] (AttributeManager.java:194) edu.serg.mbar.ucon.AttributeManager - Successfully Updated 'SimultaneousReads' of target => BudgetDoc
  INFO [main] (AccessManager.java:145) edu.serg.mbar.ucon.pdp.AccessManager - Request from subjectID[imran001] for Object whose ID is BudgetDoc for the Right of Read
 DEBUG [main] (PolicyEvaluationManager.java:72) edu.serg.mbar.ucon.pdp.PolicyEvaluationManager - evaluating policy for subject 'imran001', Object 'BudgetDoc', Right 'Read' PolicyType 'PermitAccess'
 DEBUG [main] (PolicyEvaluationManager.java:77) edu.serg.mbar.ucon.pdp.PolicyEvaluationManager - parsing the following policy file =meta/policies/BudgetDocPolicy.xml
 DEBUG [main] (PolicyEvaluationManager.java:88) edu.serg.mbar.ucon.pdp.PolicyEvaluationManager - The number of Condition elements within the Policy are 1
 DEBUG [main] (PolicyEvaluationManager.java:130) edu.serg.mbar.ucon.pdp.PolicyEvaluationManager - * Evaluating Condition
 DEBUG [main] (PolicyEvaluationManager.java:149) edu.serg.mbar.ucon.pdp.PolicyEvaluationManager - Retreiving the combining algorithm attribute of the Condition Element or Node
 DEBUG [main] (PolicyEvaluationManager.java:151) edu.serg.mbar.ucon.pdp.PolicyEvaluationManager - ** The retreived combining algorithm for the condition is edu.serg.mbar.algorithms.combiningalgorithms.All
 DEBUG [main] (PolicyEvaluationManager.java:156) edu.serg.mbar.ucon.pdp.PolicyEvaluationManager - The Number of Expression Nodes within the Condition Node = 4
  INFO [main] (ExpressionEvaluationManager.java:63) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Evaluating Expression
 DEBUG [main] (ExpressionEvaluationManager.java:71) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Start evaluating expression
  INFO [main] (ExpressionEvaluationManager.java:78) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - FunctionID of the expression being evaluated is 'edu.serg.mbar.algorithms.functions.LogicalAnd'
 DEBUG [main] (ExpressionEvaluationManager.java:82) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Child nodes within New expression Node are 3
 DEBUG [main] (ExpressionEvaluationManager.java:87) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Element 0 is p:Expression
  INFO [main] (ExpressionEvaluationManager.java:63) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Evaluating Expression
 DEBUG [main] (ExpressionEvaluationManager.java:71) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Start evaluating expression
  INFO [main] (ExpressionEvaluationManager.java:78) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - FunctionID of the expression being evaluated is 'edu.serg.mbar.algorithms.functions.StringEqualIgnoreCase'
 DEBUG [main] (ExpressionEvaluationManager.java:82) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Child nodes within New expression Node are 2
 DEBUG [main] (ExpressionEvaluationManager.java:87) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Element 0 is p:ObjectAttribute
 DEBUG [main] (AttributeManager.java:102) edu.serg.mbar.ucon.AttributeManager - Retrieve attribute(Department)of Object(imran001)
 DEBUG [main] (AttributeManager.java:105) edu.serg.mbar.ucon.AttributeManager - 1 Values for the attribute have been retrieved
 DEBUG [main] (AttributeManager.java:107) edu.serg.mbar.ucon.AttributeManager - Value1 is = Finance
 DEBUG [main] (ExpressionEvaluationManager.java:87) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Element 1 is p:Constant
 DEBUG [main] (ExpressionEvaluationManager.java:113) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - The datatype of the Constant is String
 DEBUG [main] (ExpressionEvaluationManager.java:126) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - The value of the constant = Finance
 DEBUG [main] (ExpressionEvaluationManager.java:129) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Adding the value of the constant to the list of the arguments that are to be passed to the function
 DEBUG [main] (ExpressionEvaluationManager.java:142) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - * # Calling Function 'edu.serg.mbar.algorithms.functions.StringEqualIgnoreCase' with the vector of Size 2 # *
 DEBUG [main] (StringEqualIgnoreCase.java:26) edu.serg.mbar.ucon.AttributeManager - Both Strings 'Finance' and 'Finance' are EQUAL
  INFO [main] (ExpressionEvaluationManager.java:161) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - * --> * Elapsed Time: Expression Evaluated in [00:00:00.038] Time
 DEBUG [main] (ExpressionEvaluationManager.java:87) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Element 1 is p:Expression
  INFO [main] (ExpressionEvaluationManager.java:63) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Evaluating Expression
 DEBUG [main] (ExpressionEvaluationManager.java:71) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Start evaluating expression
  INFO [main] (ExpressionEvaluationManager.java:78) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - FunctionID of the expression being evaluated is 'edu.serg.mbar.algorithms.functions.LessThan'
 DEBUG [main] (ExpressionEvaluationManager.java:82) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Child nodes within New expression Node are 2
 DEBUG [main] (ExpressionEvaluationManager.java:87) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Element 0 is p:ObjectAttribute
 DEBUG [main] (AttributeManager.java:102) edu.serg.mbar.ucon.AttributeManager - Retrieve attribute(SimultaneousReads)of Object(BudgetDoc)
 DEBUG [main] (AttributeManager.java:105) edu.serg.mbar.ucon.AttributeManager - 1 Values for the attribute have been retrieved
 DEBUG [main] (AttributeManager.java:107) edu.serg.mbar.ucon.AttributeManager - Value1 is = 3.0
 DEBUG [main] (ExpressionEvaluationManager.java:87) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Element 1 is p:Constant
 DEBUG [main] (ExpressionEvaluationManager.java:113) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - The datatype of the Constant is Integer
 DEBUG [main] (ExpressionEvaluationManager.java:126) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - The value of the constant = 2
 DEBUG [main] (ExpressionEvaluationManager.java:129) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Adding the value of the constant to the list of the arguments that are to be passed to the function
 DEBUG [main] (ExpressionEvaluationManager.java:142) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - * # Calling Function 'edu.serg.mbar.algorithms.functions.LessThan' with the vector of Size 2 # *
 DEBUG [main] (LessThan.java:29) edu.serg.mbar.algorithms.functions.LessThan - Received a request to evaluate if the first number is less than the other
 DEBUG [main] (LessThan.java:37) edu.serg.mbar.algorithms.functions.LessThan - the value of the firs number is 3.0 and the Value of the second Number is 2.0
  INFO [main] (ExpressionEvaluationManager.java:161) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - * --> * Elapsed Time: Expression Evaluated in [00:00:00.047] Time
 DEBUG [main] (ExpressionEvaluationManager.java:87) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Element 2 is p:Expression
  INFO [main] (ExpressionEvaluationManager.java:63) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Evaluating Expression
 DEBUG [main] (ExpressionEvaluationManager.java:71) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Start evaluating expression
  INFO [main] (ExpressionEvaluationManager.java:78) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - FunctionID of the expression being evaluated is 'edu.serg.mbar.algorithms.functions.LogicalAnd'
 DEBUG [main] (ExpressionEvaluationManager.java:82) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Child nodes within New expression Node are 2
 DEBUG [main] (ExpressionEvaluationManager.java:87) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Element 0 is p:Expression
  INFO [main] (ExpressionEvaluationManager.java:63) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Evaluating Expression
 DEBUG [main] (ExpressionEvaluationManager.java:71) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Start evaluating expression
  INFO [main] (ExpressionEvaluationManager.java:78) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - FunctionID of the expression being evaluated is 'edu.serg.mbar.algorithms.functions.TimeGreaterThan'
 DEBUG [main] (ExpressionEvaluationManager.java:82) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Child nodes within New expression Node are 2
 DEBUG [main] (ExpressionEvaluationManager.java:87) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Element 0 is p:Expression
  INFO [main] (ExpressionEvaluationManager.java:63) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Evaluating Expression
 DEBUG [main] (ExpressionEvaluationManager.java:71) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Start evaluating expression
  INFO [main] (ExpressionEvaluationManager.java:78) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - FunctionID of the expression being evaluated is 'edu.serg.mbar.algorithms.functions.EnvironmentVariable'
 DEBUG [main] (ExpressionEvaluationManager.java:82) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Child nodes within New expression Node are 1
 DEBUG [main] (ExpressionEvaluationManager.java:87) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Element 0 is p:Constant
 DEBUG [main] (ExpressionEvaluationManager.java:113) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - The datatype of the Constant is String
 DEBUG [main] (ExpressionEvaluationManager.java:126) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - The value of the constant = CurrentTime
 DEBUG [main] (ExpressionEvaluationManager.java:129) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Adding the value of the constant to the list of the arguments that are to be passed to the function
 DEBUG [main] (ExpressionEvaluationManager.java:142) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - * # Calling Function 'edu.serg.mbar.algorithms.functions.EnvironmentVariable' with the vector of Size 1 # *
 DEBUG [main] (EnvironmentVariable.java:30) edu.serg.mbar.algorithms.functions.EnvironmentVariable - Recieved a request to retrieve the value of an environment variable
 DEBUG [main] (EnvironmentVariable.java:34) edu.serg.mbar.algorithms.functions.EnvironmentVariable - the name of the environment variable whose value is to be retreived is CurrentTime
 DEBUG [main] (EnvironmentVariable.java:39) edu.serg.mbar.algorithms.functions.EnvironmentVariable - The Value of the environment variable is Sat Apr 04 12:03:56 GMT+05:00 2009
  INFO [main] (ExpressionEvaluationManager.java:161) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - * --> * Elapsed Time: Expression Evaluated in [00:00:00.015] Time
 DEBUG [main] (ExpressionEvaluationManager.java:87) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Element 1 is p:Constant
 DEBUG [main] (ExpressionEvaluationManager.java:113) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - The datatype of the Constant is Time
 DEBUG [main] (ExpressionEvaluationManager.java:126) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - The value of the constant = 1300
 DEBUG [main] (ExpressionEvaluationManager.java:129) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Adding the value of the constant to the list of the arguments that are to be passed to the function
 DEBUG [main] (ExpressionEvaluationManager.java:142) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - * # Calling Function 'edu.serg.mbar.algorithms.functions.TimeGreaterThan' with the vector of Size 2 # *
 DEBUG [main] (TimeGreaterThan.java:15) edu.serg.mbar.algorithms.functions.TimeGreaterThan - Received a request to evaluate if the first Value of Time is later than the second value of time
 DEBUG [main] (TimeGreaterThan.java:30) edu.serg.mbar.algorithms.functions.TimeGreaterThan - First Value of time  is Thu Jan 01 12:03:00 GMT+05:00 1970 and the Second Value is Thu Jan 01 13:00:00 GMT+05:00 1970
 DEBUG [main] (TimeGreaterThan.java:38) edu.serg.mbar.algorithms.functions.TimeGreaterThan - First value of Time is earlier than the second value - returningfalse
  INFO [main] (ExpressionEvaluationManager.java:161) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - * --> * Elapsed Time: Expression Evaluated in [00:00:00.061] Time
 DEBUG [main] (ExpressionEvaluationManager.java:87) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Element 1 is p:Expression
  INFO [main] (ExpressionEvaluationManager.java:63) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Evaluating Expression
 DEBUG [main] (ExpressionEvaluationManager.java:71) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Start evaluating expression
  INFO [main] (ExpressionEvaluationManager.java:78) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - FunctionID of the expression being evaluated is 'edu.serg.mbar.algorithms.functions.TimeLessThan'
 DEBUG [main] (ExpressionEvaluationManager.java:82) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Child nodes within New expression Node are 2
 DEBUG [main] (ExpressionEvaluationManager.java:87) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Element 0 is p:Expression
  INFO [main] (ExpressionEvaluationManager.java:63) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Evaluating Expression
 DEBUG [main] (ExpressionEvaluationManager.java:71) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Start evaluating expression
  INFO [main] (ExpressionEvaluationManager.java:78) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - FunctionID of the expression being evaluated is 'edu.serg.mbar.algorithms.functions.EnvironmentVariable'
 DEBUG [main] (ExpressionEvaluationManager.java:82) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Child nodes within New expression Node are 1
 DEBUG [main] (ExpressionEvaluationManager.java:87) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Element 0 is p:Constant
 DEBUG [main] (ExpressionEvaluationManager.java:113) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - The datatype of the Constant is String
 DEBUG [main] (ExpressionEvaluationManager.java:126) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - The value of the constant = CurrentTime
 DEBUG [main] (ExpressionEvaluationManager.java:129) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Adding the value of the constant to the list of the arguments that are to be passed to the function
 DEBUG [main] (ExpressionEvaluationManager.java:142) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - * # Calling Function 'edu.serg.mbar.algorithms.functions.EnvironmentVariable' with the vector of Size 1 # *
 DEBUG [main] (EnvironmentVariable.java:30) edu.serg.mbar.algorithms.functions.EnvironmentVariable - Recieved a request to retrieve the value of an environment variable
 DEBUG [main] (EnvironmentVariable.java:34) edu.serg.mbar.algorithms.functions.EnvironmentVariable - the name of the environment variable whose value is to be retreived is CurrentTime
 DEBUG [main] (EnvironmentVariable.java:39) edu.serg.mbar.algorithms.functions.EnvironmentVariable - The Value of the environment variable is Sat Apr 04 12:03:57 GMT+05:00 2009
  INFO [main] (ExpressionEvaluationManager.java:161) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - * --> * Elapsed Time: Expression Evaluated in [00:00:00.014] Time
 DEBUG [main] (ExpressionEvaluationManager.java:87) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Element 1 is p:Constant
 DEBUG [main] (ExpressionEvaluationManager.java:113) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - The datatype of the Constant is Time
 DEBUG [main] (ExpressionEvaluationManager.java:126) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - The value of the constant = 1700
 DEBUG [main] (ExpressionEvaluationManager.java:129) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Adding the value of the constant to the list of the arguments that are to be passed to the function
 DEBUG [main] (ExpressionEvaluationManager.java:142) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - * # Calling Function 'edu.serg.mbar.algorithms.functions.TimeLessThan' with the vector of Size 2 # *
 DEBUG [main] (TimeLessThan.java:17) edu.serg.mbar.algorithms.functions.TimeLessThan - Received a request to evaluate if the first Value of Time is earlier than the second value of time
 DEBUG [main] (TimeLessThan.java:32) edu.serg.mbar.algorithms.functions.TimeLessThan - First Value of time  is Thu Jan 01 12:03:00 GMT+05:00 1970 and the Second Value is Thu Jan 01 17:00:00 GMT+05:00 1970
 DEBUG [main] (TimeLessThan.java:36) edu.serg.mbar.algorithms.functions.TimeLessThan - First value of Time is earlier than the second value
  INFO [main] (ExpressionEvaluationManager.java:161) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - * --> * Elapsed Time: Expression Evaluated in [00:00:00.029] Time
 DEBUG [main] (ExpressionEvaluationManager.java:142) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - * # Calling Function 'edu.serg.mbar.algorithms.functions.LogicalAnd' with the vector of Size 2 # *
 DEBUG [main] (LogicalAnd.java:24) edu.serg.mbar.algorithms.functions.TimeLessThan - applying the logical and Function to 2 items
 DEBUG [main] (LogicalAnd.java:29) edu.serg.mbar.algorithms.functions.TimeLessThan - applying logicalAND function the element at 0 whose value is false
  INFO [main] (ExpressionEvaluationManager.java:161) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - * --> * Elapsed Time: Expression Evaluated in [00:00:00.121] Time
 DEBUG [main] (ExpressionEvaluationManager.java:142) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - * # Calling Function 'edu.serg.mbar.algorithms.functions.LogicalAnd' with the vector of Size 3 # *
 DEBUG [main] (LogicalAnd.java:24) edu.serg.mbar.algorithms.functions.TimeLessThan - applying the logical and Function to 3 items
 DEBUG [main] (LogicalAnd.java:29) edu.serg.mbar.algorithms.functions.TimeLessThan - applying logicalAND function the element at 0 whose value is true
 DEBUG [main] (LogicalAnd.java:29) edu.serg.mbar.algorithms.functions.TimeLessThan - applying logicalAND function the element at 1 whose value is false
  INFO [main] (ExpressionEvaluationManager.java:161) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - * --> * Elapsed Time: Expression Evaluated in [00:00:00.220] Time
  INFO [main] (ExpressionEvaluationManager.java:63) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Evaluating Expression
 DEBUG [main] (ExpressionEvaluationManager.java:71) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Start evaluating expression
  INFO [main] (ExpressionEvaluationManager.java:78) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - FunctionID of the expression being evaluated is 'edu.serg.mbar.algorithms.functions.StringEqualIgnoreCase'
 DEBUG [main] (ExpressionEvaluationManager.java:82) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Child nodes within New expression Node are 2
 DEBUG [main] (ExpressionEvaluationManager.java:87) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Element 0 is p:ObjectAttribute
 DEBUG [main] (AttributeManager.java:102) edu.serg.mbar.ucon.AttributeManager - Retrieve attribute(Department)of Object(imran001)
 DEBUG [main] (AttributeManager.java:105) edu.serg.mbar.ucon.AttributeManager - 1 Values for the attribute have been retrieved
 DEBUG [main] (AttributeManager.java:107) edu.serg.mbar.ucon.AttributeManager - Value1 is = Finance
 DEBUG [main] (ExpressionEvaluationManager.java:87) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Element 1 is p:Constant
 DEBUG [main] (ExpressionEvaluationManager.java:113) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - The datatype of the Constant is String
 DEBUG [main] (ExpressionEvaluationManager.java:126) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - The value of the constant = Finance
 DEBUG [main] (ExpressionEvaluationManager.java:129) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Adding the value of the constant to the list of the arguments that are to be passed to the function
 DEBUG [main] (ExpressionEvaluationManager.java:142) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - * # Calling Function 'edu.serg.mbar.algorithms.functions.StringEqualIgnoreCase' with the vector of Size 2 # *
 DEBUG [main] (StringEqualIgnoreCase.java:26) edu.serg.mbar.ucon.AttributeManager - Both Strings 'Finance' and 'Finance' are EQUAL
  INFO [main] (ExpressionEvaluationManager.java:161) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - * --> * Elapsed Time: Expression Evaluated in [00:00:00.047] Time
  INFO [main] (ExpressionEvaluationManager.java:63) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Evaluating Expression
 DEBUG [main] (ExpressionEvaluationManager.java:71) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Start evaluating expression
  INFO [main] (ExpressionEvaluationManager.java:78) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - FunctionID of the expression being evaluated is 'edu.serg.mbar.algorithms.functions.StringEqualIgnoreCase'
 DEBUG [main] (ExpressionEvaluationManager.java:82) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Child nodes within New expression Node are 2
 DEBUG [main] (ExpressionEvaluationManager.java:87) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Element 0 is p:ObjectAttribute
 DEBUG [main] (AttributeManager.java:102) edu.serg.mbar.ucon.AttributeManager - Retrieve attribute(Designation)of Object(imran001)
 DEBUG [main] (AttributeManager.java:105) edu.serg.mbar.ucon.AttributeManager - 1 Values for the attribute have been retrieved
 DEBUG [main] (AttributeManager.java:107) edu.serg.mbar.ucon.AttributeManager - Value1 is = ControllerFinance
 DEBUG [main] (ExpressionEvaluationManager.java:87) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Element 1 is p:Constant
 DEBUG [main] (ExpressionEvaluationManager.java:113) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - The datatype of the Constant is String
 DEBUG [main] (ExpressionEvaluationManager.java:126) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - The value of the constant = ControllerFinance
 DEBUG [main] (ExpressionEvaluationManager.java:129) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Adding the value of the constant to the list of the arguments that are to be passed to the function
 DEBUG [main] (ExpressionEvaluationManager.java:142) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - * # Calling Function 'edu.serg.mbar.algorithms.functions.StringEqualIgnoreCase' with the vector of Size 2 # *
 DEBUG [main] (StringEqualIgnoreCase.java:26) edu.serg.mbar.ucon.AttributeManager - Both Strings 'ControllerFinance' and 'ControllerFinance' are EQUAL
  INFO [main] (ExpressionEvaluationManager.java:161) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - * --> * Elapsed Time: Expression Evaluated in [00:00:00.066] Time
  INFO [main] (ExpressionEvaluationManager.java:63) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Evaluating Expression
 DEBUG [main] (ExpressionEvaluationManager.java:71) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Start evaluating expression
  INFO [main] (ExpressionEvaluationManager.java:78) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - FunctionID of the expression being evaluated is 'edu.serg.mbar.algorithms.functions.LogicalAnd'
 DEBUG [main] (ExpressionEvaluationManager.java:82) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Child nodes within New expression Node are 2
 DEBUG [main] (ExpressionEvaluationManager.java:87) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Element 0 is p:Expression
  INFO [main] (ExpressionEvaluationManager.java:63) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Evaluating Expression
 DEBUG [main] (ExpressionEvaluationManager.java:71) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Start evaluating expression
  INFO [main] (ExpressionEvaluationManager.java:78) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - FunctionID of the expression being evaluated is 'edu.serg.mbar.algorithms.functions.TimeGreaterThan'
 DEBUG [main] (ExpressionEvaluationManager.java:82) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Child nodes within New expression Node are 2
 DEBUG [main] (ExpressionEvaluationManager.java:87) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Element 0 is p:Expression
  INFO [main] (ExpressionEvaluationManager.java:63) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Evaluating Expression
 DEBUG [main] (ExpressionEvaluationManager.java:71) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Start evaluating expression
  INFO [main] (ExpressionEvaluationManager.java:78) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - FunctionID of the expression being evaluated is 'edu.serg.mbar.algorithms.functions.EnvironmentVariable'
 DEBUG [main] (ExpressionEvaluationManager.java:82) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Child nodes within New expression Node are 1
 DEBUG [main] (ExpressionEvaluationManager.java:87) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Element 0 is p:Constant
 DEBUG [main] (ExpressionEvaluationManager.java:113) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - The datatype of the Constant is String
 DEBUG [main] (ExpressionEvaluationManager.java:126) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - The value of the constant = CurrentTime
 DEBUG [main] (ExpressionEvaluationManager.java:129) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Adding the value of the constant to the list of the arguments that are to be passed to the function
 DEBUG [main] (ExpressionEvaluationManager.java:142) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - * # Calling Function 'edu.serg.mbar.algorithms.functions.EnvironmentVariable' with the vector of Size 1 # *
 DEBUG [main] (EnvironmentVariable.java:30) edu.serg.mbar.algorithms.functions.EnvironmentVariable - Recieved a request to retrieve the value of an environment variable
 DEBUG [main] (EnvironmentVariable.java:34) edu.serg.mbar.algorithms.functions.EnvironmentVariable - the name of the environment variable whose value is to be retreived is CurrentTime
 DEBUG [main] (EnvironmentVariable.java:39) edu.serg.mbar.algorithms.functions.EnvironmentVariable - The Value of the environment variable is Sat Apr 04 12:03:57 GMT+05:00 2009
  INFO [main] (ExpressionEvaluationManager.java:161) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - * --> * Elapsed Time: Expression Evaluated in [00:00:00.019] Time
 DEBUG [main] (ExpressionEvaluationManager.java:87) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Element 1 is p:Constant
 DEBUG [main] (ExpressionEvaluationManager.java:113) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - The datatype of the Constant is Time
 DEBUG [main] (ExpressionEvaluationManager.java:126) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - The value of the constant = 1300
 DEBUG [main] (ExpressionEvaluationManager.java:129) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Adding the value of the constant to the list of the arguments that are to be passed to the function
 DEBUG [main] (ExpressionEvaluationManager.java:142) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - * # Calling Function 'edu.serg.mbar.algorithms.functions.TimeGreaterThan' with the vector of Size 2 # *
 DEBUG [main] (TimeGreaterThan.java:15) edu.serg.mbar.algorithms.functions.TimeGreaterThan - Received a request to evaluate if the first Value of Time is later than the second value of time
 DEBUG [main] (TimeGreaterThan.java:30) edu.serg.mbar.algorithms.functions.TimeGreaterThan - First Value of time  is Thu Jan 01 12:03:00 GMT+05:00 1970 and the Second Value is Thu Jan 01 13:00:00 GMT+05:00 1970
 DEBUG [main] (TimeGreaterThan.java:38) edu.serg.mbar.algorithms.functions.TimeGreaterThan - First value of Time is earlier than the second value - returningfalse
  INFO [main] (ExpressionEvaluationManager.java:161) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - * --> * Elapsed Time: Expression Evaluated in [00:00:00.039] Time
 DEBUG [main] (ExpressionEvaluationManager.java:87) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Element 1 is p:Expression
  INFO [main] (ExpressionEvaluationManager.java:63) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Evaluating Expression
 DEBUG [main] (ExpressionEvaluationManager.java:71) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Start evaluating expression
  INFO [main] (ExpressionEvaluationManager.java:78) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - FunctionID of the expression being evaluated is 'edu.serg.mbar.algorithms.functions.TimeLessThan'
 DEBUG [main] (ExpressionEvaluationManager.java:82) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Child nodes within New expression Node are 2
 DEBUG [main] (ExpressionEvaluationManager.java:87) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Element 0 is p:Expression
  INFO [main] (ExpressionEvaluationManager.java:63) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Evaluating Expression
 DEBUG [main] (ExpressionEvaluationManager.java:71) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Start evaluating expression
  INFO [main] (ExpressionEvaluationManager.java:78) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - FunctionID of the expression being evaluated is 'edu.serg.mbar.algorithms.functions.EnvironmentVariable'
 DEBUG [main] (ExpressionEvaluationManager.java:82) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Child nodes within New expression Node are 1
 DEBUG [main] (ExpressionEvaluationManager.java:87) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Element 0 is p:Constant
 DEBUG [main] (ExpressionEvaluationManager.java:113) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - The datatype of the Constant is String
 DEBUG [main] (ExpressionEvaluationManager.java:126) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - The value of the constant = CurrentTime
 DEBUG [main] (ExpressionEvaluationManager.java:129) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Adding the value of the constant to the list of the arguments that are to be passed to the function
 DEBUG [main] (ExpressionEvaluationManager.java:142) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - * # Calling Function 'edu.serg.mbar.algorithms.functions.EnvironmentVariable' with the vector of Size 1 # *
 DEBUG [main] (EnvironmentVariable.java:30) edu.serg.mbar.algorithms.functions.EnvironmentVariable - Recieved a request to retrieve the value of an environment variable
 DEBUG [main] (EnvironmentVariable.java:34) edu.serg.mbar.algorithms.functions.EnvironmentVariable - the name of the environment variable whose value is to be retreived is CurrentTime
 DEBUG [main] (EnvironmentVariable.java:39) edu.serg.mbar.algorithms.functions.EnvironmentVariable - The Value of the environment variable is Sat Apr 04 12:03:57 GMT+05:00 2009
  INFO [main] (ExpressionEvaluationManager.java:161) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - * --> * Elapsed Time: Expression Evaluated in [00:00:00.020] Time
 DEBUG [main] (ExpressionEvaluationManager.java:87) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Element 1 is p:Constant
 DEBUG [main] (ExpressionEvaluationManager.java:113) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - The datatype of the Constant is Time
 DEBUG [main] (ExpressionEvaluationManager.java:126) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - The value of the constant = 1700
 DEBUG [main] (ExpressionEvaluationManager.java:129) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Adding the value of the constant to the list of the arguments that are to be passed to the function
 DEBUG [main] (ExpressionEvaluationManager.java:142) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - * # Calling Function 'edu.serg.mbar.algorithms.functions.TimeLessThan' with the vector of Size 2 # *
 DEBUG [main] (TimeLessThan.java:17) edu.serg.mbar.algorithms.functions.TimeLessThan - Received a request to evaluate if the first Value of Time is earlier than the second value of time
 DEBUG [main] (TimeLessThan.java:32) edu.serg.mbar.algorithms.functions.TimeLessThan - First Value of time  is Thu Jan 01 12:03:00 GMT+05:00 1970 and the Second Value is Thu Jan 01 17:00:00 GMT+05:00 1970
 DEBUG [main] (TimeLessThan.java:36) edu.serg.mbar.algorithms.functions.TimeLessThan - First value of Time is earlier than the second value
  INFO [main] (ExpressionEvaluationManager.java:161) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - * --> * Elapsed Time: Expression Evaluated in [00:00:00.041] Time
 DEBUG [main] (ExpressionEvaluationManager.java:142) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - * # Calling Function 'edu.serg.mbar.algorithms.functions.LogicalAnd' with the vector of Size 2 # *
 DEBUG [main] (LogicalAnd.java:24) edu.serg.mbar.algorithms.functions.TimeLessThan - applying the logical and Function to 2 items
 DEBUG [main] (LogicalAnd.java:29) edu.serg.mbar.algorithms.functions.TimeLessThan - applying logicalAND function the element at 0 whose value is false
  INFO [main] (ExpressionEvaluationManager.java:161) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - * --> * Elapsed Time: Expression Evaluated in [00:00:00.093] Time
  INFO [main] (All.java:27) edu.serg.mbar.algorithms.combiningalgorithms.All - Analyzing the Results of all the expressions in the Condition Node
 DEBUG [main] (All.java:32) edu.serg.mbar.algorithms.combiningalgorithms.All - The value of item(0) is false
 DEBUG [main] (All.java:39) edu.serg.mbar.algorithms.combiningalgorithms.All - The value returned is false
  INFO [main] (PolicyEvaluationManager.java:189) edu.serg.mbar.ucon.pdp.PolicyEvaluationManager - * --> * Elapsed Time: Condition Evaluated in [00:00:00.455] Time the Validity of the condition is false
 DEBUG [main] (PolicyEvaluationManager.java:94) edu.serg.mbar.ucon.pdp.PolicyEvaluationManager - The validity of Condition(0) is false
  INFO [main] (PolicyEvaluationManager.java:110) edu.serg.mbar.ucon.pdp.PolicyEvaluationManager - * --> * Elapsed Time: Policy Evaluation completed in [00:00:00.489] Time the Validity of the Policy is false
  INFO [main] (AccessManager.java:147) edu.serg.mbar.ucon.pdp.AccessManager - Access Decision = false
  INFO [main] (ConsoleFrontEnd.java:156) edu.serg.mbar.frontend.ConsoleFrontEnd - * --> * Elapsed Time: Execution completed in [00:00:01.555] Time
 DEBUG [main] (ConsoleFrontEnd.java:46) edu.serg.mbar.frontend.ConsoleFrontEnd - Start Execution
 DEBUG [main] (ConsoleFrontEnd.java:47) edu.serg.mbar.frontend.ConsoleFrontEnd - Initialized the log4j library.
  INFO [main] (AuthenticationManager.java:81) edu.serg.mbar.ucon.AuthenticationManager - Authenticating user: imran001
 DEBUG [main] (AuthenticationManager.java:84) edu.serg.mbar.ucon.AuthenticationManager - Using XPath to read the subject information from file: sampledata/objects/imran001.xml
 DEBUG [main] (AuthenticationManager.java:90) edu.serg.mbar.ucon.AuthenticationManager - Resolving subjectSecret using XPath: //obj:Object[obj:ObjectID/text()='imran001']/obj:Secret/text()
  INFO [main] (AuthenticationManager.java:104) edu.serg.mbar.ucon.AuthenticationManager - Authentication succeeded for subject: imran001
 DEBUG [main] (ConsoleFrontEnd.java:87) edu.serg.mbar.frontend.ConsoleFrontEnd - Authentication successful
  INFO [main] (AuthenticationManager.java:81) edu.serg.mbar.ucon.AuthenticationManager - Authenticating user: kamran001
 DEBUG [main] (AuthenticationManager.java:84) edu.serg.mbar.ucon.AuthenticationManager - Using XPath to read the subject information from file: sampledata/objects/kamran001.xml
 DEBUG [main] (AuthenticationManager.java:90) edu.serg.mbar.ucon.AuthenticationManager - Resolving subjectSecret using XPath: //obj:Object[obj:ObjectID/text()='kamran001']/obj:Secret/text()
  INFO [main] (AuthenticationManager.java:104) edu.serg.mbar.ucon.AuthenticationManager - Authentication succeeded for subject: kamran001
  INFO [main] (ConsoleFrontEnd.java:125) edu.serg.mbar.frontend.ConsoleFrontEnd - Creating an AttributeManager
  INFO [main] (ConsoleFrontEnd.java:127) edu.serg.mbar.frontend.ConsoleFrontEnd - Creating instance of the Expression Evaluator and assigning an attribute reader to it
  INFO [main] (ConsoleFrontEnd.java:133) edu.serg.mbar.frontend.ConsoleFrontEnd - Creating an AccessManager
 DEBUG [main] (AccessManager.java:71) edu.serg.mbar.ucon.pdp.AccessManager - Adding a new DecisionEvent listener.
 DEBUG [main] (AccessManager.java:83) edu.serg.mbar.ucon.pdp.AccessManager - Adding a new MutabilityEvent listener.
  INFO [main] (ConsoleFrontEnd.java:148) edu.serg.mbar.frontend.ConsoleFrontEnd - Requesting an Object 'BudgetDoc' for Subjec 'imran001'
 DEBUG [main] (PrimitiveActionsManager.java:88) edu.serg.mbar.ucon.pep.PrimitiveActionsManager - Handling Primitive Actions [PolicyType=Requesting], [Object=BudgetDoc], [Subject=imran001]
 DEBUG [main] (PrimitiveActionsManager.java:91) edu.serg.mbar.ucon.pep.PrimitiveActionsManager - Retreiving the primitive actions nodes with the help of xPath = //p:PolicySet/p:Policy[@PolicyType='Requesting' and p:Target/p:Subjects/p:Subject/text()='imran001' and p:Target/p:Objects/p:Object/text()='BudgetDoc']/p:PrimitiveActions
 DEBUG [main] (PrimitiveActionsManager.java:96) edu.serg.mbar.ucon.pep.PrimitiveActionsManager - The total number of primitive actions nodes retreived is 1
 DEBUG [main] (PrimitiveActionsManager.java:113) edu.serg.mbar.ucon.pep.PrimitiveActionsManager - Started processing Primitive Action Node
 DEBUG [main] (PrimitiveActionsManager.java:136) edu.serg.mbar.ucon.pep.PrimitiveActionsManager - Started processing Attribute update Node
  INFO [main] (ExpressionEvaluationManager.java:63) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Evaluating Expression
 DEBUG [main] (ExpressionEvaluationManager.java:71) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Start evaluating expression
  INFO [main] (ExpressionEvaluationManager.java:78) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - FunctionID of the expression being evaluated is 'edu.serg.mbar.algorithms.functions.Add'
 DEBUG [main] (ExpressionEvaluationManager.java:82) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Child nodes within New expression Node are 2
 DEBUG [main] (ExpressionEvaluationManager.java:87) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Element 0 is p:ObjectAttribute
 DEBUG [main] (AttributeManager.java:102) edu.serg.mbar.ucon.AttributeManager - Retrieve attribute(SimultaneousReads)of Object(BudgetDoc)
 DEBUG [main] (AttributeManager.java:105) edu.serg.mbar.ucon.AttributeManager - 1 Values for the attribute have been retrieved
 DEBUG [main] (AttributeManager.java:107) edu.serg.mbar.ucon.AttributeManager - Value1 is = 3.0
 DEBUG [main] (ExpressionEvaluationManager.java:87) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Element 1 is p:Constant
 DEBUG [main] (ExpressionEvaluationManager.java:113) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - The datatype of the Constant is Integer
 DEBUG [main] (ExpressionEvaluationManager.java:126) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - The value of the constant = 1
 DEBUG [main] (ExpressionEvaluationManager.java:129) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Adding the value of the constant to the list of the arguments that are to be passed to the function
 DEBUG [main] (ExpressionEvaluationManager.java:142) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - * # Calling Function 'edu.serg.mbar.algorithms.functions.Add' with the vector of Size 2 # *
 DEBUG [main] (Add.java:28) edu.serg.mbar.algorithms.functions.Add - *Start adding 2elements
 DEBUG [main] (Add.java:33) edu.serg.mbar.algorithms.functions.Add - the value of first element is 3.0
 DEBUG [main] (Add.java:33) edu.serg.mbar.algorithms.functions.Add - the value of first element is 1
 DEBUG [main] (Add.java:38) edu.serg.mbar.algorithms.functions.Add - *The result of the summation is 4.0
  INFO [main] (ExpressionEvaluationManager.java:161) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - * --> * Elapsed Time: Expression Evaluated in [00:00:00.087] Time
 DEBUG [main] (PrimitiveActionsManager.java:178) edu.serg.mbar.ucon.pep.PrimitiveActionsManager - Determining the Name of the Attribute from the TargetAttribute
 DEBUG [main] (PrimitiveActionsManager.java:202) edu.serg.mbar.ucon.pep.PrimitiveActionsManager - The name of the target attribute is 'SimultaneousReads'
  INFO [main] (AttributeManager.java:136) edu.serg.mbar.ucon.AttributeManager - Updating 'SimultaneousReads' Attribute of BudgetDoc
 DEBUG [main] (AttributeManager.java:151) edu.serg.mbar.ucon.AttributeManager - Removing old values of Attributes
 DEBUG [main] (AttributeManager.java:163) edu.serg.mbar.ucon.AttributeManager - Old Values of Attributes removed
 DEBUG [main] (AttributeManager.java:181) edu.serg.mbar.ucon.AttributeManager - Adding New values of attributes
 DEBUG [main] (AttributeManager.java:191) edu.serg.mbar.ucon.AttributeManager - New values have been added proceeding to save the document
  INFO [main] (AttributeManager.java:206) edu.serg.mbar.ucon.AttributeManager - Saving File
  INFO [main] (AttributeManager.java:223) edu.serg.mbar.ucon.AttributeManager - File Saved: sampledata/objects/BudgetDoc.xml
  INFO [main] (AttributeManager.java:194) edu.serg.mbar.ucon.AttributeManager - Successfully Updated 'SimultaneousReads' of target => BudgetDoc
  INFO [main] (AccessManager.java:147) edu.serg.mbar.ucon.pdp.AccessManager - Request from subjectID[imran001] for Object whose ID is BudgetDoc for the Right of Read
 DEBUG [main] (PolicyEvaluationManager.java:73) edu.serg.mbar.ucon.pdp.PolicyEvaluationManager - evaluating policy for subject 'imran001', Object 'BudgetDoc', Right 'Read' PolicyType 'PermitAccess'
 DEBUG [main] (PolicyEvaluationManager.java:78) edu.serg.mbar.ucon.pdp.PolicyEvaluationManager - parsing the following policy file =meta/policies/BudgetDocPolicy.xml
 DEBUG [main] (PolicyEvaluationManager.java:89) edu.serg.mbar.ucon.pdp.PolicyEvaluationManager - The number of Condition elements within the Policy are 1
 DEBUG [main] (PolicyEvaluationManager.java:131) edu.serg.mbar.ucon.pdp.PolicyEvaluationManager - * Evaluating Condition
 DEBUG [main] (PolicyEvaluationManager.java:150) edu.serg.mbar.ucon.pdp.PolicyEvaluationManager - Retreiving the combining algorithm attribute of the Condition Element or Node
 DEBUG [main] (PolicyEvaluationManager.java:152) edu.serg.mbar.ucon.pdp.PolicyEvaluationManager - ** The retreived combining algorithm for the condition is edu.serg.mbar.algorithms.combiningalgorithms.All
 DEBUG [main] (PolicyEvaluationManager.java:157) edu.serg.mbar.ucon.pdp.PolicyEvaluationManager - The Number of Expression Nodes within the Condition Node = 4
  INFO [main] (ExpressionEvaluationManager.java:63) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Evaluating Expression
 DEBUG [main] (ExpressionEvaluationManager.java:71) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Start evaluating expression
  INFO [main] (ExpressionEvaluationManager.java:78) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - FunctionID of the expression being evaluated is 'edu.serg.mbar.algorithms.functions.LogicalAnd'
 DEBUG [main] (ExpressionEvaluationManager.java:82) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Child nodes within New expression Node are 3
 DEBUG [main] (ExpressionEvaluationManager.java:87) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Element 0 is p:Expression
  INFO [main] (ExpressionEvaluationManager.java:63) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Evaluating Expression
 DEBUG [main] (ExpressionEvaluationManager.java:71) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Start evaluating expression
  INFO [main] (ExpressionEvaluationManager.java:78) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - FunctionID of the expression being evaluated is 'edu.serg.mbar.algorithms.functions.StringEqualIgnoreCase'
 DEBUG [main] (ExpressionEvaluationManager.java:82) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Child nodes within New expression Node are 2
 DEBUG [main] (ExpressionEvaluationManager.java:87) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Element 0 is p:ObjectAttribute
 DEBUG [main] (AttributeManager.java:102) edu.serg.mbar.ucon.AttributeManager - Retrieve attribute(Department)of Object(imran001)
 DEBUG [main] (AttributeManager.java:105) edu.serg.mbar.ucon.AttributeManager - 1 Values for the attribute have been retrieved
 DEBUG [main] (AttributeManager.java:107) edu.serg.mbar.ucon.AttributeManager - Value1 is = Finance
 DEBUG [main] (ExpressionEvaluationManager.java:87) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Element 1 is p:Constant
 DEBUG [main] (ExpressionEvaluationManager.java:113) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - The datatype of the Constant is String
 DEBUG [main] (ExpressionEvaluationManager.java:126) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - The value of the constant = Finance
 DEBUG [main] (ExpressionEvaluationManager.java:129) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Adding the value of the constant to the list of the arguments that are to be passed to the function
 DEBUG [main] (ExpressionEvaluationManager.java:142) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - * # Calling Function 'edu.serg.mbar.algorithms.functions.StringEqualIgnoreCase' with the vector of Size 2 # *
 DEBUG [main] (StringEqualIgnoreCase.java:26) edu.serg.mbar.ucon.AttributeManager - Both Strings 'Finance' and 'Finance' are EQUAL
  INFO [main] (ExpressionEvaluationManager.java:161) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - * --> * Elapsed Time: Expression Evaluated in [00:00:00.036] Time
 DEBUG [main] (ExpressionEvaluationManager.java:87) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Element 1 is p:Expression
  INFO [main] (ExpressionEvaluationManager.java:63) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Evaluating Expression
 DEBUG [main] (ExpressionEvaluationManager.java:71) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Start evaluating expression
  INFO [main] (ExpressionEvaluationManager.java:78) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - FunctionID of the expression being evaluated is 'edu.serg.mbar.algorithms.functions.LessThan'
 DEBUG [main] (ExpressionEvaluationManager.java:82) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Child nodes within New expression Node are 2
 DEBUG [main] (ExpressionEvaluationManager.java:87) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Element 0 is p:ObjectAttribute
 DEBUG [main] (AttributeManager.java:102) edu.serg.mbar.ucon.AttributeManager - Retrieve attribute(SimultaneousReads)of Object(BudgetDoc)
 DEBUG [main] (AttributeManager.java:105) edu.serg.mbar.ucon.AttributeManager - 1 Values for the attribute have been retrieved
 DEBUG [main] (AttributeManager.java:107) edu.serg.mbar.ucon.AttributeManager - Value1 is = 4.0
 DEBUG [main] (ExpressionEvaluationManager.java:87) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Element 1 is p:Constant
 DEBUG [main] (ExpressionEvaluationManager.java:113) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - The datatype of the Constant is Integer
 DEBUG [main] (ExpressionEvaluationManager.java:126) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - The value of the constant = 2
 DEBUG [main] (ExpressionEvaluationManager.java:129) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Adding the value of the constant to the list of the arguments that are to be passed to the function
 DEBUG [main] (ExpressionEvaluationManager.java:142) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - * # Calling Function 'edu.serg.mbar.algorithms.functions.LessThan' with the vector of Size 2 # *
 DEBUG [main] (LessThan.java:29) edu.serg.mbar.algorithms.functions.LessThan - Received a request to evaluate if the first number is less than the other
 DEBUG [main] (LessThan.java:37) edu.serg.mbar.algorithms.functions.LessThan - the value of the firs number is 4.0 and the Value of the second Number is 2.0
  INFO [main] (ExpressionEvaluationManager.java:161) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - * --> * Elapsed Time: Expression Evaluated in [00:00:00.043] Time
 DEBUG [main] (ExpressionEvaluationManager.java:87) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Element 2 is p:Expression
  INFO [main] (ExpressionEvaluationManager.java:63) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Evaluating Expression
 DEBUG [main] (ExpressionEvaluationManager.java:71) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Start evaluating expression
  INFO [main] (ExpressionEvaluationManager.java:78) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - FunctionID of the expression being evaluated is 'edu.serg.mbar.algorithms.functions.LogicalAnd'
 DEBUG [main] (ExpressionEvaluationManager.java:82) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Child nodes within New expression Node are 2
 DEBUG [main] (ExpressionEvaluationManager.java:87) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Element 0 is p:Expression
  INFO [main] (ExpressionEvaluationManager.java:63) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Evaluating Expression
 DEBUG [main] (ExpressionEvaluationManager.java:71) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Start evaluating expression
  INFO [main] (ExpressionEvaluationManager.java:78) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - FunctionID of the expression being evaluated is 'edu.serg.mbar.algorithms.functions.TimeGreaterThan'
 DEBUG [main] (ExpressionEvaluationManager.java:82) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Child nodes within New expression Node are 2
 DEBUG [main] (ExpressionEvaluationManager.java:87) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Element 0 is p:Expression
  INFO [main] (ExpressionEvaluationManager.java:63) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Evaluating Expression
 DEBUG [main] (ExpressionEvaluationManager.java:71) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Start evaluating expression
  INFO [main] (ExpressionEvaluationManager.java:78) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - FunctionID of the expression being evaluated is 'edu.serg.mbar.algorithms.functions.EnvironmentVariable'
 DEBUG [main] (ExpressionEvaluationManager.java:82) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Child nodes within New expression Node are 1
 DEBUG [main] (ExpressionEvaluationManager.java:87) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Element 0 is p:Constant
 DEBUG [main] (ExpressionEvaluationManager.java:113) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - The datatype of the Constant is String
 DEBUG [main] (ExpressionEvaluationManager.java:126) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - The value of the constant = CurrentTime
 DEBUG [main] (ExpressionEvaluationManager.java:129) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Adding the value of the constant to the list of the arguments that are to be passed to the function
 DEBUG [main] (ExpressionEvaluationManager.java:142) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - * # Calling Function 'edu.serg.mbar.algorithms.functions.EnvironmentVariable' with the vector of Size 1 # *
 DEBUG [main] (EnvironmentVariable.java:30) edu.serg.mbar.algorithms.functions.EnvironmentVariable - Recieved a request to retrieve the value of an environment variable
 DEBUG [main] (EnvironmentVariable.java:34) edu.serg.mbar.algorithms.functions.EnvironmentVariable - the name of the environment variable whose value is to be retreived is CurrentTime
 DEBUG [main] (EnvironmentVariable.java:39) edu.serg.mbar.algorithms.functions.EnvironmentVariable - The Value of the environment variable is Fri May 29 11:04:36 GMT+05:00 2009
  INFO [main] (ExpressionEvaluationManager.java:161) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - * --> * Elapsed Time: Expression Evaluated in [00:00:00.016] Time
 DEBUG [main] (ExpressionEvaluationManager.java:87) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Element 1 is p:Constant
 DEBUG [main] (ExpressionEvaluationManager.java:113) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - The datatype of the Constant is Time
 DEBUG [main] (ExpressionEvaluationManager.java:126) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - The value of the constant = 1300
 DEBUG [main] (ExpressionEvaluationManager.java:129) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Adding the value of the constant to the list of the arguments that are to be passed to the function
 DEBUG [main] (ExpressionEvaluationManager.java:142) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - * # Calling Function 'edu.serg.mbar.algorithms.functions.TimeGreaterThan' with the vector of Size 2 # *
 DEBUG [main] (TimeGreaterThan.java:15) edu.serg.mbar.algorithms.functions.TimeGreaterThan - Received a request to evaluate if the first Value of Time is later than the second value of time
 DEBUG [main] (TimeGreaterThan.java:30) edu.serg.mbar.algorithms.functions.TimeGreaterThan - First Value of time  is Thu Jan 01 11:04:00 GMT+05:00 1970 and the Second Value is Thu Jan 01 13:00:00 GMT+05:00 1970
 DEBUG [main] (TimeGreaterThan.java:38) edu.serg.mbar.algorithms.functions.TimeGreaterThan - First value of Time is earlier than the second value - returningfalse
  INFO [main] (ExpressionEvaluationManager.java:161) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - * --> * Elapsed Time: Expression Evaluated in [00:00:00.046] Time
 DEBUG [main] (ExpressionEvaluationManager.java:87) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Element 1 is p:Expression
  INFO [main] (ExpressionEvaluationManager.java:63) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Evaluating Expression
 DEBUG [main] (ExpressionEvaluationManager.java:71) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Start evaluating expression
  INFO [main] (ExpressionEvaluationManager.java:78) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - FunctionID of the expression being evaluated is 'edu.serg.mbar.algorithms.functions.TimeLessThan'
 DEBUG [main] (ExpressionEvaluationManager.java:82) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Child nodes within New expression Node are 2
 DEBUG [main] (ExpressionEvaluationManager.java:87) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Element 0 is p:Expression
  INFO [main] (ExpressionEvaluationManager.java:63) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Evaluating Expression
 DEBUG [main] (ExpressionEvaluationManager.java:71) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Start evaluating expression
  INFO [main] (ExpressionEvaluationManager.java:78) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - FunctionID of the expression being evaluated is 'edu.serg.mbar.algorithms.functions.EnvironmentVariable'
 DEBUG [main] (ExpressionEvaluationManager.java:82) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Child nodes within New expression Node are 1
 DEBUG [main] (ExpressionEvaluationManager.java:87) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Element 0 is p:Constant
 DEBUG [main] (ExpressionEvaluationManager.java:113) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - The datatype of the Constant is String
 DEBUG [main] (ExpressionEvaluationManager.java:126) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - The value of the constant = CurrentTime
 DEBUG [main] (ExpressionEvaluationManager.java:129) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Adding the value of the constant to the list of the arguments that are to be passed to the function
 DEBUG [main] (ExpressionEvaluationManager.java:142) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - * # Calling Function 'edu.serg.mbar.algorithms.functions.EnvironmentVariable' with the vector of Size 1 # *
 DEBUG [main] (EnvironmentVariable.java:30) edu.serg.mbar.algorithms.functions.EnvironmentVariable - Recieved a request to retrieve the value of an environment variable
 DEBUG [main] (EnvironmentVariable.java:34) edu.serg.mbar.algorithms.functions.EnvironmentVariable - the name of the environment variable whose value is to be retreived is CurrentTime
 DEBUG [main] (EnvironmentVariable.java:39) edu.serg.mbar.algorithms.functions.EnvironmentVariable - The Value of the environment variable is Fri May 29 11:04:36 GMT+05:00 2009
  INFO [main] (ExpressionEvaluationManager.java:161) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - * --> * Elapsed Time: Expression Evaluated in [00:00:00.016] Time
 DEBUG [main] (ExpressionEvaluationManager.java:87) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Element 1 is p:Constant
 DEBUG [main] (ExpressionEvaluationManager.java:113) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - The datatype of the Constant is Time
 DEBUG [main] (ExpressionEvaluationManager.java:126) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - The value of the constant = 1700
 DEBUG [main] (ExpressionEvaluationManager.java:129) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Adding the value of the constant to the list of the arguments that are to be passed to the function
 DEBUG [main] (ExpressionEvaluationManager.java:142) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - * # Calling Function 'edu.serg.mbar.algorithms.functions.TimeLessThan' with the vector of Size 2 # *
 DEBUG [main] (TimeLessThan.java:17) edu.serg.mbar.algorithms.functions.TimeLessThan - Received a request to evaluate if the first Value of Time is earlier than the second value of time
 DEBUG [main] (TimeLessThan.java:32) edu.serg.mbar.algorithms.functions.TimeLessThan - First Value of time  is Thu Jan 01 11:04:00 GMT+05:00 1970 and the Second Value is Thu Jan 01 17:00:00 GMT+05:00 1970
 DEBUG [main] (TimeLessThan.java:36) edu.serg.mbar.algorithms.functions.TimeLessThan - First value of Time is earlier than the second value
  INFO [main] (ExpressionEvaluationManager.java:161) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - * --> * Elapsed Time: Expression Evaluated in [00:00:00.032] Time
 DEBUG [main] (ExpressionEvaluationManager.java:142) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - * # Calling Function 'edu.serg.mbar.algorithms.functions.LogicalAnd' with the vector of Size 2 # *
 DEBUG [main] (LogicalAnd.java:24) edu.serg.mbar.algorithms.functions.TimeLessThan - applying the logical and Function to 2 items
 DEBUG [main] (LogicalAnd.java:29) edu.serg.mbar.algorithms.functions.TimeLessThan - applying logicalAND function the element at 0 whose value is false
  INFO [main] (ExpressionEvaluationManager.java:161) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - * --> * Elapsed Time: Expression Evaluated in [00:00:00.103] Time
 DEBUG [main] (ExpressionEvaluationManager.java:142) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - * # Calling Function 'edu.serg.mbar.algorithms.functions.LogicalAnd' with the vector of Size 3 # *
 DEBUG [main] (LogicalAnd.java:24) edu.serg.mbar.algorithms.functions.TimeLessThan - applying the logical and Function to 3 items
 DEBUG [main] (LogicalAnd.java:29) edu.serg.mbar.algorithms.functions.TimeLessThan - applying logicalAND function the element at 0 whose value is true
 DEBUG [main] (LogicalAnd.java:29) edu.serg.mbar.algorithms.functions.TimeLessThan - applying logicalAND function the element at 1 whose value is false
  INFO [main] (ExpressionEvaluationManager.java:161) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - * --> * Elapsed Time: Expression Evaluated in [00:00:00.194] Time
  INFO [main] (ExpressionEvaluationManager.java:63) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Evaluating Expression
 DEBUG [main] (ExpressionEvaluationManager.java:71) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Start evaluating expression
  INFO [main] (ExpressionEvaluationManager.java:78) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - FunctionID of the expression being evaluated is 'edu.serg.mbar.algorithms.functions.StringEqualIgnoreCase'
 DEBUG [main] (ExpressionEvaluationManager.java:82) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Child nodes within New expression Node are 2
 DEBUG [main] (ExpressionEvaluationManager.java:87) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Element 0 is p:ObjectAttribute
 DEBUG [main] (AttributeManager.java:102) edu.serg.mbar.ucon.AttributeManager - Retrieve attribute(Department)of Object(imran001)
 DEBUG [main] (AttributeManager.java:105) edu.serg.mbar.ucon.AttributeManager - 1 Values for the attribute have been retrieved
 DEBUG [main] (AttributeManager.java:107) edu.serg.mbar.ucon.AttributeManager - Value1 is = Finance
 DEBUG [main] (ExpressionEvaluationManager.java:87) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Element 1 is p:Constant
 DEBUG [main] (ExpressionEvaluationManager.java:113) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - The datatype of the Constant is String
 DEBUG [main] (ExpressionEvaluationManager.java:126) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - The value of the constant = Finance
 DEBUG [main] (ExpressionEvaluationManager.java:129) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Adding the value of the constant to the list of the arguments that are to be passed to the function
 DEBUG [main] (ExpressionEvaluationManager.java:142) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - * # Calling Function 'edu.serg.mbar.algorithms.functions.StringEqualIgnoreCase' with the vector of Size 2 # *
 DEBUG [main] (StringEqualIgnoreCase.java:26) edu.serg.mbar.ucon.AttributeManager - Both Strings 'Finance' and 'Finance' are EQUAL
  INFO [main] (ExpressionEvaluationManager.java:161) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - * --> * Elapsed Time: Expression Evaluated in [00:00:00.052] Time
  INFO [main] (ExpressionEvaluationManager.java:63) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Evaluating Expression
 DEBUG [main] (ExpressionEvaluationManager.java:71) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Start evaluating expression
  INFO [main] (ExpressionEvaluationManager.java:78) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - FunctionID of the expression being evaluated is 'edu.serg.mbar.algorithms.functions.StringEqualIgnoreCase'
 DEBUG [main] (ExpressionEvaluationManager.java:82) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Child nodes within New expression Node are 2
 DEBUG [main] (ExpressionEvaluationManager.java:87) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Element 0 is p:ObjectAttribute
 DEBUG [main] (AttributeManager.java:102) edu.serg.mbar.ucon.AttributeManager - Retrieve attribute(Designation)of Object(imran001)
 DEBUG [main] (AttributeManager.java:105) edu.serg.mbar.ucon.AttributeManager - 1 Values for the attribute have been retrieved
 DEBUG [main] (AttributeManager.java:107) edu.serg.mbar.ucon.AttributeManager - Value1 is = ControllerFinance
 DEBUG [main] (ExpressionEvaluationManager.java:87) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Element 1 is p:Constant
 DEBUG [main] (ExpressionEvaluationManager.java:113) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - The datatype of the Constant is String
 DEBUG [main] (ExpressionEvaluationManager.java:126) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - The value of the constant = ControllerFinance
 DEBUG [main] (ExpressionEvaluationManager.java:129) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Adding the value of the constant to the list of the arguments that are to be passed to the function
 DEBUG [main] (ExpressionEvaluationManager.java:142) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - * # Calling Function 'edu.serg.mbar.algorithms.functions.StringEqualIgnoreCase' with the vector of Size 2 # *
 DEBUG [main] (StringEqualIgnoreCase.java:26) edu.serg.mbar.ucon.AttributeManager - Both Strings 'ControllerFinance' and 'ControllerFinance' are EQUAL
  INFO [main] (ExpressionEvaluationManager.java:161) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - * --> * Elapsed Time: Expression Evaluated in [00:00:00.080] Time
  INFO [main] (ExpressionEvaluationManager.java:63) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Evaluating Expression
 DEBUG [main] (ExpressionEvaluationManager.java:71) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Start evaluating expression
  INFO [main] (ExpressionEvaluationManager.java:78) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - FunctionID of the expression being evaluated is 'edu.serg.mbar.algorithms.functions.LogicalAnd'
 DEBUG [main] (ExpressionEvaluationManager.java:82) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Child nodes within New expression Node are 2
 DEBUG [main] (ExpressionEvaluationManager.java:87) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Element 0 is p:Expression
  INFO [main] (ExpressionEvaluationManager.java:63) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Evaluating Expression
 DEBUG [main] (ExpressionEvaluationManager.java:71) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Start evaluating expression
  INFO [main] (ExpressionEvaluationManager.java:78) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - FunctionID of the expression being evaluated is 'edu.serg.mbar.algorithms.functions.TimeGreaterThan'
 DEBUG [main] (ExpressionEvaluationManager.java:82) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Child nodes within New expression Node are 2
 DEBUG [main] (ExpressionEvaluationManager.java:87) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Element 0 is p:Expression
  INFO [main] (ExpressionEvaluationManager.java:63) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Evaluating Expression
 DEBUG [main] (ExpressionEvaluationManager.java:71) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Start evaluating expression
  INFO [main] (ExpressionEvaluationManager.java:78) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - FunctionID of the expression being evaluated is 'edu.serg.mbar.algorithms.functions.EnvironmentVariable'
 DEBUG [main] (ExpressionEvaluationManager.java:82) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Child nodes within New expression Node are 1
 DEBUG [main] (ExpressionEvaluationManager.java:87) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Element 0 is p:Constant
 DEBUG [main] (ExpressionEvaluationManager.java:113) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - The datatype of the Constant is String
 DEBUG [main] (ExpressionEvaluationManager.java:126) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - The value of the constant = CurrentTime
 DEBUG [main] (ExpressionEvaluationManager.java:129) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Adding the value of the constant to the list of the arguments that are to be passed to the function
 DEBUG [main] (ExpressionEvaluationManager.java:142) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - * # Calling Function 'edu.serg.mbar.algorithms.functions.EnvironmentVariable' with the vector of Size 1 # *
 DEBUG [main] (EnvironmentVariable.java:30) edu.serg.mbar.algorithms.functions.EnvironmentVariable - Recieved a request to retrieve the value of an environment variable
 DEBUG [main] (EnvironmentVariable.java:34) edu.serg.mbar.algorithms.functions.EnvironmentVariable - the name of the environment variable whose value is to be retreived is CurrentTime
 DEBUG [main] (EnvironmentVariable.java:39) edu.serg.mbar.algorithms.functions.EnvironmentVariable - The Value of the environment variable is Fri May 29 11:04:36 GMT+05:00 2009
  INFO [main] (ExpressionEvaluationManager.java:161) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - * --> * Elapsed Time: Expression Evaluated in [00:00:00.029] Time
 DEBUG [main] (ExpressionEvaluationManager.java:87) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Element 1 is p:Constant
 DEBUG [main] (ExpressionEvaluationManager.java:113) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - The datatype of the Constant is Time
 DEBUG [main] (ExpressionEvaluationManager.java:126) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - The value of the constant = 1300
 DEBUG [main] (ExpressionEvaluationManager.java:129) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Adding the value of the constant to the list of the arguments that are to be passed to the function
 DEBUG [main] (ExpressionEvaluationManager.java:142) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - * # Calling Function 'edu.serg.mbar.algorithms.functions.TimeGreaterThan' with the vector of Size 2 # *
 DEBUG [main] (TimeGreaterThan.java:15) edu.serg.mbar.algorithms.functions.TimeGreaterThan - Received a request to evaluate if the first Value of Time is later than the second value of time
 DEBUG [main] (TimeGreaterThan.java:30) edu.serg.mbar.algorithms.functions.TimeGreaterThan - First Value of time  is Thu Jan 01 11:04:00 GMT+05:00 1970 and the Second Value is Thu Jan 01 13:00:00 GMT+05:00 1970
 DEBUG [main] (TimeGreaterThan.java:38) edu.serg.mbar.algorithms.functions.TimeGreaterThan - First value of Time is earlier than the second value - returningfalse
  INFO [main] (ExpressionEvaluationManager.java:161) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - * --> * Elapsed Time: Expression Evaluated in [00:00:00.050] Time
 DEBUG [main] (ExpressionEvaluationManager.java:87) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Element 1 is p:Expression
  INFO [main] (ExpressionEvaluationManager.java:63) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Evaluating Expression
 DEBUG [main] (ExpressionEvaluationManager.java:71) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Start evaluating expression
  INFO [main] (ExpressionEvaluationManager.java:78) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - FunctionID of the expression being evaluated is 'edu.serg.mbar.algorithms.functions.TimeLessThan'
 DEBUG [main] (ExpressionEvaluationManager.java:82) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Child nodes within New expression Node are 2
 DEBUG [main] (ExpressionEvaluationManager.java:87) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Element 0 is p:Expression
  INFO [main] (ExpressionEvaluationManager.java:63) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Evaluating Expression
 DEBUG [main] (ExpressionEvaluationManager.java:71) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Start evaluating expression
  INFO [main] (ExpressionEvaluationManager.java:78) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - FunctionID of the expression being evaluated is 'edu.serg.mbar.algorithms.functions.EnvironmentVariable'
 DEBUG [main] (ExpressionEvaluationManager.java:82) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Child nodes within New expression Node are 1
 DEBUG [main] (ExpressionEvaluationManager.java:87) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Element 0 is p:Constant
 DEBUG [main] (ExpressionEvaluationManager.java:113) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - The datatype of the Constant is String
 DEBUG [main] (ExpressionEvaluationManager.java:126) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - The value of the constant = CurrentTime
 DEBUG [main] (ExpressionEvaluationManager.java:129) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Adding the value of the constant to the list of the arguments that are to be passed to the function
 DEBUG [main] (ExpressionEvaluationManager.java:142) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - * # Calling Function 'edu.serg.mbar.algorithms.functions.EnvironmentVariable' with the vector of Size 1 # *
 DEBUG [main] (EnvironmentVariable.java:30) edu.serg.mbar.algorithms.functions.EnvironmentVariable - Recieved a request to retrieve the value of an environment variable
 DEBUG [main] (EnvironmentVariable.java:34) edu.serg.mbar.algorithms.functions.EnvironmentVariable - the name of the environment variable whose value is to be retreived is CurrentTime
 DEBUG [main] (EnvironmentVariable.java:39) edu.serg.mbar.algorithms.functions.EnvironmentVariable - The Value of the environment variable is Fri May 29 11:04:36 GMT+05:00 2009
  INFO [main] (ExpressionEvaluationManager.java:161) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - * --> * Elapsed Time: Expression Evaluated in [00:00:00.025] Time
 DEBUG [main] (ExpressionEvaluationManager.java:87) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Element 1 is p:Constant
 DEBUG [main] (ExpressionEvaluationManager.java:113) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - The datatype of the Constant is Time
 DEBUG [main] (ExpressionEvaluationManager.java:126) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - The value of the constant = 1700
 DEBUG [main] (ExpressionEvaluationManager.java:129) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - Adding the value of the constant to the list of the arguments that are to be passed to the function
 DEBUG [main] (ExpressionEvaluationManager.java:142) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - * # Calling Function 'edu.serg.mbar.algorithms.functions.TimeLessThan' with the vector of Size 2 # *
 DEBUG [main] (TimeLessThan.java:17) edu.serg.mbar.algorithms.functions.TimeLessThan - Received a request to evaluate if the first Value of Time is earlier than the second value of time
 DEBUG [main] (TimeLessThan.java:32) edu.serg.mbar.algorithms.functions.TimeLessThan - First Value of time  is Thu Jan 01 11:04:00 GMT+05:00 1970 and the Second Value is Thu Jan 01 17:00:00 GMT+05:00 1970
 DEBUG [main] (TimeLessThan.java:36) edu.serg.mbar.algorithms.functions.TimeLessThan - First value of Time is earlier than the second value
  INFO [main] (ExpressionEvaluationManager.java:161) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - * --> * Elapsed Time: Expression Evaluated in [00:00:00.049] Time
 DEBUG [main] (ExpressionEvaluationManager.java:142) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - * # Calling Function 'edu.serg.mbar.algorithms.functions.LogicalAnd' with the vector of Size 2 # *
 DEBUG [main] (LogicalAnd.java:24) edu.serg.mbar.algorithms.functions.TimeLessThan - applying the logical and Function to 2 items
 DEBUG [main] (LogicalAnd.java:29) edu.serg.mbar.algorithms.functions.TimeLessThan - applying logicalAND function the element at 0 whose value is false
  INFO [main] (ExpressionEvaluationManager.java:161) edu.serg.mbar.ucon.pdp.ExpressionEvaluationManager - * --> * Elapsed Time: Expression Evaluated in [00:00:00.111] Time
  INFO [main] (All.java:27) edu.serg.mbar.algorithms.combiningalgorithms.All - Analyzing the Results of all the expressions in the Condition Node
 DEBUG [main] (All.java:32) edu.serg.mbar.algorithms.combiningalgorithms.All - The value of item(0) is false
 DEBUG [main] (All.java:39) edu.serg.mbar.algorithms.combiningalgorithms.All - The value returned is false
  INFO [main] (PolicyEvaluationManager.java:190) edu.serg.mbar.ucon.pdp.PolicyEvaluationManager - * --> * Elapsed Time: Condition Evaluated in [00:00:00.473] Time the Validity of the condition is false
 